[11/17 13:47:35,Telerik.TestStudio.Desktop.exe(7028:1)] First trace message from process 7028: "C:\Program Files (x86)\Telerik\Test Studio\Bin\Telerik.TestStudio.Desktop.exe" False "C:\Test\Test\OptemisRISTest" /trial
[11/17 13:47:35,Telerik.TestStudio.Desktop.exe(7028:1)] First trace message from foreground thread "Application start thread" (managed ID = 1, native ID = 8624).
[11/17 13:47:35,Telerik.TestStudio.Desktop.exe(7028:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test 'SMOKE_002___Case_Creation_UI' selected.
[11/17 13:47:38,Telerik.TestStudio.Desktop.exe(7028:1),Framework] Connector.Start() : Process launched (ID=10804, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",QueryWpfApplicationsEntryPoint 17500518").
[11/17 13:47:38,Telerik.TestStudio.Desktop.exe(7028:1),Framework] WpfAppSnoop.WndProc() : Received WM_COPYDATA: COPYDATASTRUCT.dwData reports PID 9380 as a WPF application.
[11/17 13:47:38,Telerik.TestStudio.Desktop.exe(7028:1),Framework] WpfAppSnoop.WndProc() : Received WM_COPYDATA: COPYDATASTRUCT.dwData reports PID 7028 as a WPF application.
[11/17 13:47:38,Telerik.TestStudio.Desktop.exe(7028:1),Framework] WpfAppSnoop.WndProc() : Received WM_COPYDATA: COPYDATASTRUCT.dwData reports PID 10172 as a WPF application.
[11/17 13:47:38,Telerik.TestStudio.Desktop.exe(7028:1),Framework] WpfAppSnoop.WndProc() : Received WM_COPYDATA: COPYDATASTRUCT.dwData reports PID 9148 as a WPF application.
[11/17 13:47:38,Telerik.TestStudio.Desktop.exe(7028:1),Framework] WpfAppSnoop.WndProc() : Received WM_COPYDATA: COPYDATASTRUCT.dwData reports PID 7936 as a WPF application.
[11/17 13:47:38,Telerik.TestStudio.Desktop.exe(7028:1),Framework] Connector.Start() : Process exited (ID=10804, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",QueryWpfApplicationsEntryPoint 17500518").
[11/17 13:47:38,Telerik.TestStudio.Desktop.exe(7028:1),Framework] Connector.Start() : Process launched (ID=2888, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector_64.dll",QueryWpfApplicationsEntryPoint 17566054").
[11/17 13:47:38,Telerik.TestStudio.Desktop.exe(7028:1),Framework] Connector.Start() : Process exited (ID=2888, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector_64.dll",QueryWpfApplicationsEntryPoint 17566054").
[11/17 13:47:42,Telerik.TestStudio.Desktop.exe(7028:1),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:47:43,ArtOfTest.Runner.exe(2056:3)] First trace message from process 2056: "C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=4844
[11/17 13:47:43,ArtOfTest.Runner.exe(2056:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 7908).
[11/17 13:47:43,ArtOfTest.Runner.exe(2056:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[11/17 13:47:43,ArtOfTest.Runner.exe(2056:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[11/17 13:47:43,ArtOfTest.Runner.exe(2056:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 10824).
[11/17 13:47:43,ArtOfTest.Runner.exe(2056:5),Uncategorized] BugTrackersLoader.LoadSystems() : ------- Function Start -------
[11/17 13:47:48,ArtOfTest.Runner.exe(2056:5),Framework] Manager.LaunchNewApplication() : Process launched (ID=2212, Path="C:\Users\jweisbach\AppData\Local\Apps\2.0\E3CMAO4L.84J\DQGECP19.WE8\sq1optemis_0000000000000000_0001.0001_588dc50b02b96054\TMC RIS Workstation.exe", Arguments="").
[11/17 13:47:48,ArtOfTest.Runner.exe(2056:5),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[11/17 13:47:48,ArtOfTest.Runner.exe(2056:6)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 6, native ID = 10928).
[11/17 13:47:48,ArtOfTest.Runner.exe(2056:6),Framework] <>c__DisplayClass73.<WaitForApplicationToConnect>b__6d() : WPF HWNDs detected after 13 msec: {395696}.
[11/17 13:47:48,ArtOfTest.Runner.exe(2056:6),Framework] Connector.Start() : Process launched (ID=10980, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 395696").
[11/17 13:47:48,ArtOfTest.Runner.exe(2056:6),Framework] Connector.Start() : Process exited (ID=10980, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 395696").
[11/17 13:47:48,ArtOfTest.Runner.exe(2056:6),Framework] Connector.InjectCode() : ------- Function Start -------
[11/17 13:47:48,ArtOfTest.Runner.exe(2056:6),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056
[11/17 13:47:48,ArtOfTest.Runner.exe(2056:6),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 395696 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056")...
[11/17 13:47:49,TMC RIS Workstation.exe(2212:1)] First trace message from process 2212: "C:\Users\jweisbach\AppData\Local\Apps\2.0\E3CMAO4L.84J\DQGECP19.WE8\sq1optemis_0000000000000000_0001.0001_588dc50b02b96054\TMC RIS Workstation.exe" 
[11/17 13:47:49,TMC RIS Workstation.exe(2212:1)] First trace message from foreground thread "WpfCommunicationEntryPoint entry point thread" (managed ID = 1, native ID = 2692).
[11/17 13:47:49,TMC RIS Workstation.exe(2212:1),Recording] WpfCommunicationEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (hwndString="395696:Pipe.ArtOfTest.WebAii.BrowserProvisioner_1445659953:2056").
[11/17 13:47:49,TMC RIS Workstation.exe(2212:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_1102becb-e40d-4f6c-af67-3704d0318cd5".
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:8)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 8, native ID = 9172).
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:8),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[11/17 13:47:49,TMC RIS Workstation.exe(2212:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "35832b09-c63d-4fc9-a219-7278186b5ecf.CommandPipe", Async = "35832b09-c63d-4fc9-a219-7278186b5ecf.AsyncPipe"}
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:9)] First trace message from background thread "Remoted async command listener" (managed ID = 9, native ID = 11252).
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:9),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:9),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:9),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:6),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:6),Framework] Connector.InjectCode() : ------- Function End -------
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'SMOKE_002___Case_Creation_UI', path: 'Automated Tests\1. Smoke Tests\002 - Case Creation Smoke Test\SMOKE_002___Case_Creation_UI.tstest.'
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Uncategorized] ExtensionsManager.LoadExtensions() : Got 9 plugin(s) in folder "C:\Program Files (x86)\Telerik\Test Studio\Bin\Plugins".
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.TeamPulse.dll" from plugins folder.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:11)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 11, native ID = 10648).
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:11),Framework] Manager._windowMonitor_WindowOpened() : New WPF window detected (HWND=330166, Caption="Telemedicine Clinic Optemis - OPTEMIS-SQ1 environment (RELEASE Build)").
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:11),Framework] Connector.Start() : Process launched (ID=10720, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 330166").
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:11),Framework] Connector.Start() : Process exited (ID=10720, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 330166").
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : ------- Function Start -------
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 330166 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056")...
[11/17 13:47:49,TMC RIS Workstation.exe(2212:1),Recording] WpfCommunicationEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (hwndString="330166:Pipe.ArtOfTest.WebAii.BrowserProvisioner_1445659953:2056").
[11/17 13:47:49,TMC RIS Workstation.exe(2212:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_fad42d7a-35e6-4775-aac8-d31525510fb0".
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:8),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:18)] First trace message from background thread "Remoted async command listener" (managed ID = 18, native ID = 9192).
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:18),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[11/17 13:47:49,TMC RIS Workstation.exe(2212:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "2c621f30-893d-41bc-b70b-531e94524831.CommandPipe", Async = "2c621f30-893d-41bc-b70b-531e94524831.AsyncPipe"}
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:18),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:18),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : ------- Function End -------
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test '[ALL] ClickButtonOK'"...
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: '[ALL] ClickButtonOK', path: 'Automated Tests\Steps [ALL}\[ALL] ClickButtonOK.tstest.'
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:47:49,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:11),Framework] Manager._windowMonitor_WindowOpened() : New WPF window detected (HWND=330168, Caption="Optemis").
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:11),Framework] Connector.Start() : Process launched (ID=9844, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 330168").
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:11),Framework] Connector.Start() : Process exited (ID=9844, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 330168").
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : ------- Function Start -------
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 330168 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056")...
[11/17 13:47:50,TMC RIS Workstation.exe(2212:1),Recording] WpfCommunicationEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (hwndString="330168:Pipe.ArtOfTest.WebAii.BrowserProvisioner_1445659953:2056").
[11/17 13:47:50,TMC RIS Workstation.exe(2212:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_fe793c65-44b7-4982-be4d-5944bb7acdce".
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:8),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:19)] First trace message from background thread "Remoted async command listener" (managed ID = 19, native ID = 9068).
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:19),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[11/17 13:47:50,TMC RIS Workstation.exe(2212:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "955ac50e-09e4-42b1-9bf6-fab9cb8bf6ef.CommandPipe", Async = "955ac50e-09e4-42b1-9bf6-fab9cb8bf6ef.AsyncPipe"}
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:19),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:19),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : ------- Function End -------
[11/17 13:47:50,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Click BtnOkRadbutton"...
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Click BtnOkRadbutton".
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test '[ALL] ClickButtonOK'".
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:15)] First trace message from foreground thread "AnnotatorUIThread" (managed ID = 15, native ID = 2920).
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:15),Framework] Manager.get_ActiveWpfWindow() : ActiveWpfWindow was changed and set to LastOrDefault. Current active window was either invisible or disconnected
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:15),Framework] Manager.get_ActiveWpfWindow() : ActiveWpfWindow is 'Telemedicine Clinic Optemis - OPTEMIS-SQ1 environment (RELEASE Build)'
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Execute test '[NEWCASE] NewPatientDetails'"...
[11/17 13:47:51,TMC RIS Workstation.exe(2212:12)] First trace message from background thread "Client command listener thread" (managed ID = 12, native ID = 9028).
[11/17 13:47:51,TMC RIS Workstation.exe(2212:12),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_fe793c65-44b7-4982-be4d-5944bb7acdce") to pipe server...
[11/17 13:47:51,TMC RIS Workstation.exe(2212:12),Framework] BrowserRemoteClient.Disconnect() : Disconnecting command pipe (Handle = 1924).
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:12)] First trace message from background thread "MS UI Automation event queue processor" (managed ID = 12, native ID = 7020).
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:12),Warning] Manager.RemoveBrowser() : Attempted to remove a client ID "Client_fe793c65-44b7-4982-be4d-5944bb7acdce" that was not in the list.
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:12),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 19).
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:19),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A ClientDisconnectRequest(ClientId="Client_fe793c65-44b7-4982-be4d-5944bb7acdce") has been received from the pipe client.
[11/17 13:47:51,TMC RIS Workstation.exe(2212:8)] First trace message from background thread "Client command listener thread" (managed ID = 8, native ID = 10092).
[11/17 13:47:51,TMC RIS Workstation.exe(2212:8),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_1102becb-e40d-4f6c-af67-3704d0318cd5") to pipe server...
[11/17 13:47:51,TMC RIS Workstation.exe(2212:8),Framework] BrowserRemoteClient.Disconnect() : Disconnecting command pipe (Handle = 1860).
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:12),Warning] Manager.RemoveBrowser() : Attempted to remove a client ID "Client_1102becb-e40d-4f6c-af67-3704d0318cd5" that was not in the list.
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:12),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 9).
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:9),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: '[NEWCASE] NewPatientDetails', path: 'Automated Tests\Steps [ALL}\[NEWCASE] NewPatientDetails.tstest.'
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:47:51,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:47:52,ArtOfTest.Runner.exe(2056:11),Framework] Manager._windowMonitor_WindowOpened() : New WPF window detected (HWND=461232, Caption="Optemis Login").
[11/17 13:47:52,ArtOfTest.Runner.exe(2056:11),Framework] Connector.Start() : Process launched (ID=10516, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 461232").
[11/17 13:47:52,ArtOfTest.Runner.exe(2056:11),Framework] Connector.Start() : Process exited (ID=10516, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 461232").
[11/17 13:47:52,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : ------- Function Start -------
[11/17 13:47:52,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056
[11/17 13:47:52,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 461232 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056")...
[11/17 13:47:52,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_INVALID_WINDOW_HANDLE).
[11/17 13:47:52,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : ------- Function End -------
[11/17 13:47:58,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:47:58,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:47:59,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Desktop command: LeftClick on CaseEntryRadribbontab"...
[11/17 13:47:59,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Desktop command: LeftClick on CaseEntryRadribbontab".
[11/17 13:47:59,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:47:59,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:47:59,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:47:59,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:47:59,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:00,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Desktop command: LeftClick on CaseEntryRadribbontab"...
[11/17 13:48:00,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Desktop command: LeftClick on CaseEntryRadribbontab".
[11/17 13:48:00,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:00,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:00,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:00,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:00,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Click NewExamRadribbonbutton"...
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Click NewExamRadribbonbutton".
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:11),Framework] Manager._windowMonitor_WindowOpened() : New WPF window detected (HWND=657840, Caption="Create Exam").
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:11),Framework] Connector.Start() : Process launched (ID=6356, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 657840").
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:11),Framework] Connector.Start() : Process exited (ID=6356, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 657840").
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : ------- Function Start -------
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 657840 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056")...
[11/17 13:48:05,TMC RIS Workstation.exe(2212:1),Recording] WpfCommunicationEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (hwndString="657840:Pipe.ArtOfTest.WebAii.BrowserProvisioner_1445659953:2056").
[11/17 13:48:05,TMC RIS Workstation.exe(2212:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_5e82e9e2-069e-46a1-9cea-5cac720b76bc".
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:8),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:22)] First trace message from background thread "Remoted async command listener" (managed ID = 22, native ID = 7584).
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:22),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[11/17 13:48:05,TMC RIS Workstation.exe(2212:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "bbd866bc-6462-437d-8871-c62f979254b7.CommandPipe", Async = "bbd866bc-6462-437d-8871-c62f979254b7.AsyncPipe"}
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:22),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:22),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : ------- Function End -------
[11/17 13:48:05,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "LeftClick on Item0Textboxview"...
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "LeftClick on Item0Textboxview".
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "[CASE_NewPatientDetails_CodedStep] : New Coded Step"...
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:07,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:11,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "[CASE_NewPatientDetails_CodedStep] : New Coded Step".
[11/17 13:48:11,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:11,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:11,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:11,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:11,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:12,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Extract TbMRNTextbox's text content into DataBindVariable $(Extracted MRN)"...
[11/17 13:48:12,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Extract TbMRNTextbox's text content into DataBindVariable $(Extracted MRN)".
[11/17 13:48:12,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:12,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:12,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:12,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:12,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:13,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "LeftClick on Item1Textboxview"...
[11/17 13:48:13,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "LeftClick on Item1Textboxview".
[11/17 13:48:13,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:13,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:13,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:13,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:13,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:13,ArtOfTest.Runner.exe(2056:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:13,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "[CASE_NewPatientDetails_CodedStep1] : New Coded Step"...
[11/17 13:48:14,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "[CASE_NewPatientDetails_CodedStep1] : New Coded Step".
[11/17 13:48:14,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:14,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:14,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:14,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:14,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:15,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "LeftClick on Item2Textboxview"...
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "LeftClick on Item2Textboxview".
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "[CASE_NewPatientDetails_CodedStep2] : New Coded Step"...
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "[CASE_NewPatientDetails_CodedStep2] : New Coded Step".
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:16,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:17,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "LeftClick on Item3Textboxview"...
[11/17 13:48:17,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "LeftClick on Item3Textboxview".
[11/17 13:48:17,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:17,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:17,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:17,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:17,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:17,ArtOfTest.Runner.exe(2056:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:17,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "[CASE_NewPatientDetails_CodedStep3] : New Coded Step"...
[11/17 13:48:18,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "[CASE_NewPatientDetails_CodedStep3] : New Coded Step".
[11/17 13:48:18,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:18,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:18,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:18,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:18,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:19,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 13 "LeftClick on Item4Textboxview0"...
[11/17 13:48:19,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 13 "LeftClick on Item4Textboxview0".
[11/17 13:48:19,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:19,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:19,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:19,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:19,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:19,ArtOfTest.Runner.exe(2056:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:19,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 14 "[CASE_NewPatientDetails_CodedStep4] : New Coded Step"...
[11/17 13:48:19,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:20,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 14 "[CASE_NewPatientDetails_CodedStep4] : New Coded Step".
[11/17 13:48:20,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:20,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:20,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:20,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:20,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:21,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 15 "Click NextRadbutton"...
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 15 "Click NextRadbutton".
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Execute test '[NEWCASE] NewPatientDetails'".
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Execute test '[NEWCASE] NewExamDetails'"...
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: '[NEWCASE] NewExamDetails', path: 'Automated Tests\Steps [ALL}\[NEWCASE] NewExamDetails.tstest.'
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:22,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "[CASE_NewExamDetails_CodedStep] : Type 'AccessionNumber'"...
[11/17 13:48:26,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "[CASE_NewExamDetails_CodedStep] : Type 'AccessionNumber'".
[11/17 13:48:26,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:26,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:26,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:26,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:26,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:27,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Desktop command: LeftClick on PARTDateTimeInputRadwatermarktextbox"...
[11/17 13:48:27,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Desktop command: LeftClick on PARTDateTimeInputRadwatermarktextbox".
[11/17 13:48:27,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:27,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:27,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:27,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:27,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:27,ArtOfTest.Runner.exe(2056:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:27,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "[CASE_NewExamDetails_CodedStep1] : Type 'scanTime'"...
[11/17 13:48:27,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:28,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "[CASE_NewExamDetails_CodedStep1] : Type 'scanTime'".
[11/17 13:48:28,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:28,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:28,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:28,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:28,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:29,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Desktop command: LeftClick on PARTDropDownButtonRadtogglebutton"...
[11/17 13:48:30,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:30,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Desktop command: LeftClick on PARTDropDownButtonRadtogglebutton".
[11/17 13:48:30,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:30,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:30,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:30,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:30,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:30,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:31,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Type 'aleris' into ExamDetailsClientComboRadcombobox"...
[11/17 13:48:32,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Type 'aleris' into ExamDetailsClientComboRadcombobox".
[11/17 13:48:32,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:32,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:32,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:32,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:32,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:33,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Press Return"...
[11/17 13:48:33,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Press Return".
[11/17 13:48:33,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:33,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:33,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:33,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:33,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:34,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Desktop command: LeftClick on Item1Radcombobox"...
[11/17 13:48:34,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:34,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:34,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Desktop command: LeftClick on Item1Radcombobox".
[11/17 13:48:34,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:34,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:34,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:34,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:34,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:35,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Press Down (2 times)"...
[11/17 13:48:35,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Press Down (2 times)".
[11/17 13:48:35,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:35,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:35,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:35,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:35,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:37,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Press Return"...
[11/17 13:48:37,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Press Return".
[11/17 13:48:37,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:37,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:37,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:37,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:37,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:38,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "Desktop command: LeftClick on TextSearchRadwatermarktextbox"...
[11/17 13:48:38,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "Desktop command: LeftClick on TextSearchRadwatermarktextbox".
[11/17 13:48:38,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:38,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:38,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:38,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:38,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:39,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "radwatermarktextbox: Type 'abdomen' into TextSearchRadwatermarktextbox"...
[11/17 13:48:40,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "radwatermarktextbox: Type 'abdomen' into TextSearchRadwatermarktextbox".
[11/17 13:48:40,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:40,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:40,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:40,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:40,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:43,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "Desktop command: LeftDoubleClick on MRSkanningAfAbdomenListboxitem"...
[11/17 13:48:44,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "Desktop command: LeftDoubleClick on MRSkanningAfAbdomenListboxitem".
[11/17 13:48:44,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:44,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:44,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:44,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:44,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:45,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 13 "radcombobox: drop down 'Open' action."...
[11/17 13:48:45,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:45,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 13 "radcombobox: drop down 'Open' action.".
[11/17 13:48:45,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:45,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:45,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:45,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:45,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:46,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 14 "radcombobox: selecting item 'Single'"...
[11/17 13:48:46,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 14 "radcombobox: selecting item 'Single'".
[11/17 13:48:46,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:46,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:46,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:46,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:46,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 15 "Desktop command: LeftClick on NextRadbutton"...
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 15 "Desktop command: LeftClick on NextRadbutton".
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Execute test '[NEWCASE] NewExamDetails'".
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Execute test '[NEWCASE] Search'n'Start'"...
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: '[NEWCASE] Search'n'Start', path: 'Automated Tests\Steps [ALL}\[NEWCASE] Search'n'Start.tstest.'
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:12),Warning] Manager.RemoveBrowser() : Attempted to remove a client ID "Client_5e82e9e2-069e-46a1-9cea-5cac720b76bc" that was not in the list.
[11/17 13:48:48,TMC RIS Workstation.exe(2212:17)] First trace message from background thread "Client command listener thread" (managed ID = 17, native ID = 5000).
[11/17 13:48:48,TMC RIS Workstation.exe(2212:17),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_5e82e9e2-069e-46a1-9cea-5cac720b76bc") to pipe server...
[11/17 13:48:48,ArtOfTest.Runner.exe(2056:12),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 22).
[11/17 13:48:48,TMC RIS Workstation.exe(2212:17),Framework] BrowserRemoteClient.Disconnect() : Disconnecting command pipe (Handle = 2664).
[11/17 13:48:50,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "radribbontab: item 'Searches' select action."...
[11/17 13:48:50,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "radribbontab: item 'Searches' select action.".
[11/17 13:48:50,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:50,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:50,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:50,ArtOfTest.Runner.exe(2056:15),Framework] Manager.get_ActiveWpfWindow() : ActiveWpfWindow was changed and set to LastOrDefault. Current active window was either invisible or disconnected
[11/17 13:48:50,ArtOfTest.Runner.exe(2056:15),Framework] Manager.get_ActiveWpfWindow() : ActiveWpfWindow is 'Telemedicine Clinic Optemis - OPTEMIS-SQ1 environment (RELEASE Build)'
[11/17 13:48:50,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:50,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:51,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "radribbontab: item 'Searches' select action."...
[11/17 13:48:52,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "radribbontab: item 'Searches' select action.".
[11/17 13:48:52,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:52,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:52,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:52,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:52,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:53,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "LeftClick on PARTClientAreaBorderBorder"...
[11/17 13:48:53,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "LeftClick on PARTClientAreaBorderBorder".
[11/17 13:48:53,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:53,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:53,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:53,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:53,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:55,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Desktop command: LeftClick on FindExamsRadribbonbutton"...
[11/17 13:48:55,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Desktop command: LeftClick on FindExamsRadribbonbutton".
[11/17 13:48:55,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:55,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:55,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:55,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:55,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:56,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "LeftClick on HeaderButtonRadtogglebutton"...
[11/17 13:48:56,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "LeftClick on HeaderButtonRadtogglebutton".
[11/17 13:48:56,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:56,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:56,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:56,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:56,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:58,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Desktop command: LeftClick on Item2Textbox"...
[11/17 13:48:58,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Desktop command: LeftClick on Item2Textbox".
[11/17 13:48:58,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:58,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:58,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:48:58,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:48:58,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:48:59,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Type 'MRN' into Item2Textbox - DataDriven: [$(Extracted MRN)]"...
[11/17 13:49:03,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Type 'MRN' into Item2Textbox - DataDriven: [$(Extracted MRN)]".
[11/17 13:49:03,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:49:03,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:49:03,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:49:03,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:49:03,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:49:04,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Desktop command: LeftClick on EditorRadribbonbutton"...
[11/17 13:49:04,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Desktop command: LeftClick on EditorRadribbonbutton".
[11/17 13:49:04,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:49:04,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:49:04,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:49:04,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:49:04,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:49:07,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Desktop command: LeftClick on Item167Border"...
[11/17 13:49:07,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Desktop command: LeftClick on Item167Border".
[11/17 13:49:07,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:49:07,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:49:07,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:49:07,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:49:07,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "Desktop command: LeftClick on EditExamRadribbonbutton"...
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "Desktop command: LeftClick on EditExamRadribbonbutton".
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:11),Framework] Manager._windowMonitor_WindowOpened() : New WPF window detected (HWND=723376, Caption="Edit Case").
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:11),Framework] Connector.Start() : Process launched (ID=9988, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 723376").
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:11),Framework] Connector.Start() : Process exited (ID=9988, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 723376").
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : ------- Function Start -------
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 723376 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056")...
[11/17 13:49:09,TMC RIS Workstation.exe(2212:1),Recording] WpfCommunicationEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (hwndString="723376:Pipe.ArtOfTest.WebAii.BrowserProvisioner_1445659953:2056").
[11/17 13:49:09,TMC RIS Workstation.exe(2212:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_89c36ebb-f097-41b3-9a98-dc72162f5476".
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:8),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:23)] First trace message from background thread "Remoted async command listener" (managed ID = 23, native ID = 7960).
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:23),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[11/17 13:49:09,TMC RIS Workstation.exe(2212:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "985cba4e-fba9-4936-852a-8622b9e0685a.CommandPipe", Async = "985cba4e-fba9-4936-852a-8622b9e0685a.AsyncPipe"}
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:23),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:23),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : ------- Function End -------
[11/17 13:49:09,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "Click SaveAndStartRadbutton"...
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "Click SaveAndStartRadbutton".
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:11),Framework] Manager._windowMonitor_WindowOpened() : New WPF window detected (HWND=526766, Caption="Start Case").
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:11),Framework] Connector.Start() : Process launched (ID=9132, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 526766").
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:11),Framework] Connector.Start() : Process exited (ID=9132, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 526766").
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : ------- Function Start -------
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 526766 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\Telerik.TestingFramework.WpfExtension\v4.0_2015.2.723.0__3fd81c5c4516f739\Telerik.TestingFramework.WpfExtension.dll?Telerik.TestingFramework.WpfExtension.WpfCommunicationEntryPoint?2056")...
[11/17 13:49:11,TMC RIS Workstation.exe(2212:1),Recording] WpfCommunicationEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (hwndString="526766:Pipe.ArtOfTest.WebAii.BrowserProvisioner_1445659953:2056").
[11/17 13:49:11,TMC RIS Workstation.exe(2212:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_acb17259-cd20-40e5-903d-55ef36f1df9a".
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:8),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:24)] First trace message from background thread "Remoted async command listener" (managed ID = 24, native ID = 3296).
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:24),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[11/17 13:49:11,TMC RIS Workstation.exe(2212:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "e9a4a63a-a349-494b-88b5-ba49da6ee758.CommandPipe", Async = "e9a4a63a-a349-494b-88b5-ba49da6ee758.AsyncPipe"}
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:24),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:24),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:11),Framework] Connector.InjectCode() : ------- Function End -------
[11/17 13:49:11,ArtOfTest.Runner.exe(2056:11),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "Desktop command: LeftClick on OKRadbutton"...
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "Desktop command: LeftClick on OKRadbutton".
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Execute test '[NEWCASE] Search'n'Start'".
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::ShutDown::Start
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::Start
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::End
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DispatcherBeginInvokeShutdown::Start
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::Close::Start
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1445659953" in order to unblock and eventually terminate it...
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:8),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[11/17 13:49:13,TMC RIS Workstation.exe(2212:11)] First trace message from background thread "Client command listener thread" (managed ID = 11, native ID = 10000).
[11/17 13:49:13,TMC RIS Workstation.exe(2212:11),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_fad42d7a-35e6-4775-aac8-d31525510fb0") to pipe server...
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Warning] Manager.RemoveBrowser() : Attempted to remove a client ID "Client_fad42d7a-35e6-4775-aac8-d31525510fb0" that was not in the list.
[11/17 13:49:13,TMC RIS Workstation.exe(2212:11),Framework] BrowserRemoteClient.Disconnect() : Disconnecting command pipe (Handle = 1084).
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 18).
[11/17 13:49:13,TMC RIS Workstation.exe(2212:17)] First trace message from background thread "Client command listener thread" (managed ID = 17, native ID = 8068).
[11/17 13:49:13,TMC RIS Workstation.exe(2212:17),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_89c36ebb-f097-41b3-9a98-dc72162f5476") to pipe server...
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Warning] Manager.RemoveBrowser() : Attempted to remove a client ID "Client_89c36ebb-f097-41b3-9a98-dc72162f5476" that was not in the list.
[11/17 13:49:13,TMC RIS Workstation.exe(2212:17),Framework] BrowserRemoteClient.Disconnect() : Disconnecting command pipe (Handle = 2668).
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 23).
[11/17 13:49:13,TMC RIS Workstation.exe(2212:18)] First trace message from background thread "Client command listener thread" (managed ID = 18, native ID = 8872).
[11/17 13:49:13,TMC RIS Workstation.exe(2212:18),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_acb17259-cd20-40e5-903d-55ef36f1df9a") to pipe server...
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Warning] Manager.RemoveBrowser() : Attempted to remove a client ID "Client_acb17259-cd20-40e5-903d-55ef36f1df9a" that was not in the list.
[11/17 13:49:13,TMC RIS Workstation.exe(2212:18),Framework] BrowserRemoteClient.Disconnect() : Disconnecting command pipe (Handle = 2868).
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:5),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 24).
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:12),Warning] Manager.RemoveBrowser() : Attempted to remove a client ID "Client_acb17259-cd20-40e5-903d-55ef36f1df9a" that was not in the list.
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:12),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 24).
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:12),Warning] Manager.RemoveBrowser() : Attempted to remove a client ID "Client_89c36ebb-f097-41b3-9a98-dc72162f5476" that was not in the list.
[11/17 13:49:13,ArtOfTest.Runner.exe(2056:12),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 23).
[11/17 13:49:15,ArtOfTest.Runner.exe(2056:5),Framework] WpfApplication.Detach() : Process killed (ID=2212, ExitCode=4294967295, Path="C:\Users\jweisbach\AppData\Local\Apps\2.0\E3CMAO4L.84J\DQGECP19.WE8\sq1optemis_0000000000000000_0001.0001_588dc50b02b96054\TMC RIS Workstation.exe", Arguments="").
[11/17 13:49:15,ArtOfTest.Runner.exe(2056:12),Warning] Manager.RemoveBrowser() : Attempted to remove a client ID "Client_fad42d7a-35e6-4775-aac8-d31525510fb0" that was not in the list.
[11/17 13:49:15,ArtOfTest.Runner.exe(2056:12),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 18).
[11/17 13:49:15,Telerik.TestStudio.Desktop.exe(7028:16)] First trace message from background thread "Callback Listener Thread" (managed ID = 16, native ID = 11132).
[11/17 13:49:15,Telerik.TestStudio.Desktop.exe(7028:16),Warning] PipeCommunication.SyncPipeRead() : Pipe was already disposed at the moment of the read attempt.
